[INFO] cloning repository https://github.com/hTuneSys/hexaTuneDsp
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/hTuneSys/hexaTuneDsp" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FhTuneSys%2FhexaTuneDsp", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FhTuneSys%2FhexaTuneDsp'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 601506987b2edf53c12f9a7cdfee6ffa2e809b3a
[INFO] testing hTuneSys/hexaTuneDsp against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FhTuneSys%2FhexaTuneDsp" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/hTuneSys/hexaTuneDsp
[INFO] finished tweaking git repo https://github.com/hTuneSys/hexaTuneDsp
[INFO] tweaked toml for git repo https://github.com/hTuneSys/hexaTuneDsp written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/hTuneSys/hexaTuneDsp on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/hTuneSys/hexaTuneDsp already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded hound v3.5.1
[INFO] [stderr]   Downloaded clap v4.5.60
[INFO] [stderr]   Downloaded toml_writer v1.0.6+spec-1.1.0
[INFO] [stderr]   Downloaded clap_builder v4.5.60
[INFO] [stderr]   Downloaded cbindgen v0.29.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 48405dd8d6b430ee2a08aca591e8a395eb6ef1c43d372d5f318ebd8606d426b5
[INFO] running `Command { std: "docker" "start" "-a" "48405dd8d6b430ee2a08aca591e8a395eb6ef1c43d372d5f318ebd8606d426b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "48405dd8d6b430ee2a08aca591e8a395eb6ef1c43d372d5f318ebd8606d426b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48405dd8d6b430ee2a08aca591e8a395eb6ef1c43d372d5f318ebd8606d426b5", kill_on_drop: false }`
[INFO] [stdout] 48405dd8d6b430ee2a08aca591e8a395eb6ef1c43d372d5f318ebd8606d426b5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0516250290a96e198a11e8c4f3fe9d8935e7cb543fffe958feee54f7d143da21
[INFO] running `Command { std: "docker" "start" "-a" "0516250290a96e198a11e8c4f3fe9d8935e7cb543fffe958feee54f7d143da21", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling anstyle-parse v0.2.7
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling colorchoice v1.0.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling cbindgen v0.29.2
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling hound v3.5.1
[INFO] [stderr]    Compiling toml_parser v1.0.9+spec-1.1.0
[INFO] [stderr]    Compiling tempfile v3.26.0
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling toml v0.9.12+spec-1.1.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling hexatune-dsp-ffi v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 33.68s
[INFO] running `Command { std: "docker" "inspect" "0516250290a96e198a11e8c4f3fe9d8935e7cb543fffe958feee54f7d143da21", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0516250290a96e198a11e8c4f3fe9d8935e7cb543fffe958feee54f7d143da21", kill_on_drop: false }`
[INFO] [stdout] 0516250290a96e198a11e8c4f3fe9d8935e7cb543fffe958feee54f7d143da21
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9ffae0d343ebc7cd128b6eae9690446ba69c81a3b6009b750e25699dfe9abb1b
[INFO] running `Command { std: "docker" "start" "-a" "9ffae0d343ebc7cd128b6eae9690446ba69c81a3b6009b750e25699dfe9abb1b", kill_on_drop: false }`
[INFO] [stderr]    Compiling hexatune-dsp-ffi v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.00s
[INFO] running `Command { std: "docker" "inspect" "9ffae0d343ebc7cd128b6eae9690446ba69c81a3b6009b750e25699dfe9abb1b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9ffae0d343ebc7cd128b6eae9690446ba69c81a3b6009b750e25699dfe9abb1b", kill_on_drop: false }`
[INFO] [stdout] 9ffae0d343ebc7cd128b6eae9690446ba69c81a3b6009b750e25699dfe9abb1b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 87cf51efc19e73f6c9a2e64576357c7bc02ac523c51005b6639648e80d959298
[INFO] running `Command { std: "docker" "start" "-a" "87cf51efc19e73f6c9a2e64576357c7bc02ac523c51005b6639648e80d959298", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/hexatune_dsp_ffi-12f8b4f8a9214612)
[INFO] [stdout] 
[INFO] [stdout] running 60 tests
[INFO] [stdout] test engine::tests::clear_base_also_clears_textures_and_events ... ok
[INFO] [stdout] test engine::tests::engine_creates_successfully ... ok
[INFO] [stdout] test engine::tests::event_requires_base ... ok
[INFO] [stdout] test binaural::tests::binaural_produces_different_channels ... ok
[INFO] [stdout] test engine::tests::config_update_changes_behavior ... ok
[INFO] [stdout] test engine::tests::renders_audio_when_running ... ok
[INFO] [stdout] test engine::tests::renders_silence_when_stopped ... ok
[INFO] [stdout] test binaural::tests::am_mode_produces_identical_channels ... ok
[INFO] [stdout] test engine::tests::texture_index_bounds_checked ... ok
[INFO] [stdout] test engine::tests::texture_requires_base ... ok
[INFO] [stdout] test engine::tests::start_stop_transitions ... ok
[INFO] [stdout] test event_player::tests::event_plays_and_finishes ... ok
[INFO] [stdout] test event_player::tests::max_one_concurrent_event ... ok
[INFO] [stdout] test engine::tests::base_layer_contributes_to_output ... ok
[INFO] [stdout] test event_player::tests::pan_affects_stereo_output ... ok
[INFO] [stdout] test event_player::tests::rejects_invalid_index ... ok
[INFO] [stdout] test event_player::tests::set_and_clear_event ... ok
[INFO] [stdout] test event_player::tests::stereo_event_loads_correctly ... ok
[INFO] [stdout] test event_player::tests::clear_all_stops_playback ... ok
[INFO] [stdout] test event_scheduler::tests::scheduler_counts_down ... ok
[INFO] [stdout] test event_scheduler::tests::scheduler_round_robins_slots ... ok
[INFO] [stdout] test event_scheduler::tests::scheduler_triggers_at_zero_countdown ... ok
[INFO] [stdout] test engine::tests::gain_updates_are_applied ... ok
[INFO] [stdout] test engine::tests::output_stays_in_range ... ok
[INFO] [stdout] test event_scheduler::tests::xorshift64_range_f32_respects_bounds ... ok
[INFO] [stdout] test event_scheduler::tests::xorshift64_f32_in_range ... ok
[INFO] [stdout] test event_scheduler::tests::xorshift64_produces_different_values ... ok
[INFO] [stdout] test event_scheduler::tests::xorshift64_range_u64_respects_bounds ... ok
[INFO] [stdout] test event_scheduler::tests::zero_seed_becomes_one ... ok
[INFO] [stdout] test mixer::tests::all_layers_contribute ... ok
[INFO] [stdout] test mixer::tests::default_gains_are_correct ... ok
[INFO] [stdout] test mixer::tests::master_gain_scales_everything ... ok
[INFO] [stdout] test mixer::tests::mix_zero_gain_produces_silence ... ok
[INFO] [stdout] test mixer::tests::mix_applies_gains ... ok
[INFO] [stdout] test oscillator::tests::oscillator_produces_sine_at_correct_frequency ... ok
[INFO] [stdout] test mixer::tests::output_is_clamped ... ok
[INFO] [stdout] test sample_player::tests::crossfade_blends_at_loop_boundary ... ok
[INFO] [stdout] test oscillator::tests::set_frequency_preserves_phase ... ok
[INFO] [stdout] test sample_player::tests::crossfade_clamped_for_short_buffers ... ok
[INFO] [stdout] test sample_player::tests::empty_player_returns_silence ... ok
[INFO] [stdout] test sample_player::tests::load_raw_pcm_stereo ... ok
[INFO] [stdout] test sample_player::tests::loops_at_end_of_buffer ... ok
[INFO] [stdout] test sample_player::tests::mono_load_duplicates_channels ... ok
[INFO] [stdout] test scheduler::tests::all_oneshot_exhausts_after_first_cycle ... ok
[INFO] [stdout] test binaural::tests::set_delta_changes_output ... ok
[INFO] [stdout] test scheduler::tests::cycles_through_items ... ok
[INFO] [stdout] test scheduler::tests::cycle_count_increments ... ok
[INFO] [stdout] test scheduler::tests::empty_schedule_returns_no_change ... ok
[INFO] [stdout] test sample_player::tests::stereo_load_deinterleaves ... ok
[INFO] [stdout] test scheduler::tests::graceful_stop_at_cycle_end ... ok
[INFO] [stdout] test scheduler::tests::oneshot_skipped_in_second_iteration ... ok
[INFO] [stdout] test scheduler::tests::graceful_stop_with_oneshot ... ok
[INFO] [stdout] test sample_player::tests::load_raw_pcm_mono ... ok
[INFO] [stdout] test scheduler::tests::oneshot_plays_in_first_iteration ... ok
[INFO] [stdout] test scheduler::tests::set_items_resets_state ... ok
[INFO] [stdout] test scheduler::tests::single_item_stays_forever ... ok
[INFO] [stdout] test scheduler::tests::reset_clears_all_state ... ok
[INFO] [stdout] test oscillator::tests::output_stays_in_range ... ok
[INFO] [stderr]    Doc-tests hexatune_dsp_ffi
[INFO] [stdout] test event_player::tests::empty_system_returns_silence ... ok
[INFO] [stdout] test event_scheduler::tests::scheduler_disabled_returns_none ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 60 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "87cf51efc19e73f6c9a2e64576357c7bc02ac523c51005b6639648e80d959298", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "87cf51efc19e73f6c9a2e64576357c7bc02ac523c51005b6639648e80d959298", kill_on_drop: false }`
[INFO] [stdout] 87cf51efc19e73f6c9a2e64576357c7bc02ac523c51005b6639648e80d959298
